[2017.09.14 17:14:25] [0000m.00s.046ms] [1] FlowInstallVersion: WARNING: Could not get flow install version file [InstallVersion.txt] creation time
[2017.09.14 17:14:25] [0000m.00s.053ms] [0] WELCOME
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] iCDB Server ver.1.094, vt40T8RtWJOG2cE2Nlvc5A, build:535943 created 2013-01-08 09:09:26, sources stamp:vt40T8RtWJOG2cE2Nlvc5A
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] Copyright 2013 Mentor Graphics Corporation. All Rights Reserved.
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] Base flow version [7.9.4]
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] GBS Install Id [26418], Flow Id [EE7.9.4], Full Flow Name [Expedition Enterprise Flow], Exact Access Date [Jun 1 2012]
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] UPDATE Install Info, GBS Install Id [29736], UPDATE Name [Expedition Enterprise Flow - Update 10 for EE7.9.4]
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] Machine [W2130]  User [levk]  PID [6060]
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] Operating system [Microsoft Windows 7 Business Edition, 64-bit Service Pack 1 (build 7601)]
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] Current working dir [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB]
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] PATH [C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\xe\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\wg\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\MGC_HOME.ixn\bin;C:\MentorGraphics\7.9.4EE\MGC_HOME.ixn\lib;C:\Program Files\Common Files\Microsoft Shared\Microsoft Online Services;C:\Program Files (x86)\Common Files\Microsoft Shared\Microsoft Online Services;C:\ProgramData\Oracle\Java\javapath;C:\MentorGraphics\Allegro Export ODB++\nv\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Common Files\Acronis\SnapAPI\;C:\Program Files (x86)\Altium Designer S09 Viewer\System;C:\Program Files\Aladdin\eToken\PKIClient\x32;C:\Program Files\Aladdin\eToken\PKIClient\x64;C:\Program Files\TortoiseSVN\bin;C:\Program Files (x86)\Skype\Phone\;C:\Cadence\SPB_16.6\openaccess\bin\win32\opt;C:\Cadence\SPB_16.6\tools\capture;C:\Cadence\SPB_16.6\tools\pspice;C:\Cadence\SPB_16.6\tools\specctra\bin;C:\Cadence\SPB_16.6\tools\fet\bin;C:\Cadence\SPB_16.6\tools\libutil\bin;C:\Cadence\SPB_16.6\tools\bin;C:\Cadence\SPB_16.6\tools\pcb\bin;;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.5EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\1.0CCASM\SDD_HOME\common\win32\lib]
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] SDD_HOME [C:\MentorGraphics\7.9.4EE\SDD_HOME] on file system [NTFS]
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] SDD_PLATFORM [win32]
[2017.09.14 17:14:25] [0000m.00s.053ms] [1] WDIR [C:\WDIR\7.9.4EE;C:\MentorGraphics\7.9.4EE\SDD_HOME\standard]
[2017.09.14 17:14:25] [0000m.00s.054ms] [1] Local iCDB working dir [C:\WDIR\7.9.4EE\iCDB] on file system [NTFS]
[2017.09.14 17:14:25] [0000m.00s.056ms] [1] S: Config file name [ServerWG.cfg]
[2017.09.14 17:14:25] [0000m.00s.056ms] [2] S: Loading config files
[2017.09.14 17:14:25] [0000m.00s.067ms] [1] Config file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\cdbsvr\ServerWG.cfg] not found
[2017.09.14 17:14:25] [0000m.00s.067ms] [1] Config: Non-default value [System.SingleUserMode] = [no]
[2017.09.14 17:14:25] [0000m.00s.069ms] [3] S: Config loaded
[2017.09.14 17:14:25] [0000m.00s.094ms] [0] S: Project path [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] on file system [NTFS]
[2017.09.14 17:14:25] [0000m.00s.095ms] [0] S: Project global path [\\berg\data\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] on file system [NTFS]
[2017.09.14 17:14:25] [0000m.00s.095ms] [0] S: Project file (.prj) path [\\berg\data\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\FC-MPCI-1R0.prj] on file system [UNKNOWN]
[2017.09.14 17:14:25] [0000m.00s.096ms] [0] S: Project file (.prj) local path [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\FC-MPCI-1R0.prj] on file system [NTFS]
[2017.09.14 17:14:25] [0000m.00s.101ms] [1] S: WARNING: Database auto-repair support DISABLED
[2017.09.14 17:14:25] [0000m.00s.101ms] [0] S: Multi-user mode (config)
[2017.09.14 17:14:25] [0000m.00s.101ms] [0] S: Event polling mode autodetection
[2017.09.14 17:14:25] [0000m.00s.109ms] [0] S: Started up automatically by iCDB Client: W2130:levk:6632
[2017.09.14 17:14:25] [0000m.00s.109ms] [0] S: Auto close mode
[2017.09.14 17:14:25] [0000m.00s.109ms] [0] S: Database ID [9158-59ed-635f-dd63-6b0f-fa9b-b0ff-a950-beae-8c0c-2cd9-86c5]
[2017.09.14 17:14:25] [0000m.00s.109ms] [0] PathTranslator: BEGIN dumping
[2017.09.14 17:14:25] [0000m.00s.109ms] [0] PathTranslator: Local -> global path prefix list
[2017.09.14 17:14:25] [0000m.00s.109ms] [0] PathTranslator: [V:\] -> [\\SQLSERVER\Software\]
[2017.09.14 17:14:25] [0000m.00s.109ms] [0] PathTranslator: [W:\] -> [\\SQLSERVER\AppDistribution\]
[2017.09.14 17:14:25] [0000m.00s.109ms] [0] PathTranslator: [X:\] -> [\\BERG\system\Ufolders\levk\]
[2017.09.14 17:14:25] [0000m.00s.109ms] [0] PathTranslator: [Y:\] -> [\\berg\programs\]
[2017.09.14 17:14:25] [0000m.00s.110ms] [0] PathTranslator: [Z:\] -> [\\berg\data\]
[2017.09.14 17:14:25] [0000m.00s.110ms] [0] PathTranslator: Global -> local path prefix list
[2017.09.14 17:14:25] [0000m.00s.110ms] [0] PathTranslator: [\\SQLSERVER\AppDistribution\] -> [W:\]
[2017.09.14 17:14:25] [0000m.00s.110ms] [0] PathTranslator: [\\BERG\system\Ufolders\levk\] -> [X:\]
[2017.09.14 17:14:25] [0000m.00s.110ms] [0] PathTranslator: [\\SQLSERVER\Software\] -> [V:\]
[2017.09.14 17:14:25] [0000m.00s.110ms] [0] PathTranslator: [\\berg\programs\] -> [Y:\]
[2017.09.14 17:14:25] [0000m.00s.110ms] [0] PathTranslator: [\\berg\data\] -> [Z:\]
[2017.09.14 17:14:25] [0000m.00s.110ms] [0] PathTranslator: END dumping
[2017.09.14 17:14:25] [0000m.00s.110ms] [0] S: START
[2017.09.14 17:14:25] [0000m.00s.112ms] [0] S: Server startup lock file created
[2017.09.14 17:14:25] [0000m.00s.115ms] [0] S: Listening for client connections on port [10000]
[2017.09.14 17:14:25] [0000m.00s.116ms] [0] S: Server network ID [W2130:192.168.10.119,W2130,W2130.VG.LOCAL:10000]
[2017.09.14 17:14:25] [0000m.00s.116ms] [0] S: Server GUID [d965-fe1d-fa6f-c222-1ce8-ab96-ce8a-b950-7e0e-8c0c-2cd9-86c5]
[2017.09.14 17:14:25] [0000m.00s.116ms] [0] S: Project GUID [9158-59ed-635f-dd63-6b0f-fa9b-b0ff-a950-beae-8c0c-2cd9-86c5]
[2017.09.14 17:14:25] [0000m.00s.124ms] [0] S: Wrote data to server address file
[2017.09.14 17:14:25] [0000m.00s.125ms] [0] S: Server startup lock file removed
[2017.09.14 17:14:25] [0000m.00s.127ms] [1] ProjectBackupManager: Setting project file (.prj) path: [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\FC-MPCI-1R0.prj]
[2017.09.14 17:14:25] [0000m.00s.235ms] [0] S: Plugins loaded: [PIM] [ENetGen70] 
[2017.09.14 17:14:25] [0000m.00s.235ms] [0] S: Local disc access time: 44 us
[2017.09.14 17:14:25] [0000m.00s.236ms] [0] S: File server access time: 216 us
[2017.09.14 17:14:25] [0000m.00s.248ms] [0] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\icdb.dat] size [73840 (72.1 kB)]
[2017.09.14 17:14:25] [0000m.00s.250ms] [1] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\icdb.dat] opened in read-write mode
[2017.09.14 17:14:25] [0000m.00s.252ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.14 17:14:25] [0000m.00s.252ms] [0] Database GUARD OK: [73840]
[2017.09.14 17:14:25] [0000m.00s.260ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.14 17:14:25] [0000m.00s.260ms] [0] Database GUARD OK: [73840]
[2017.09.14 17:14:25] [0000m.00s.260ms] [0] SingleFile: [ab06-e095-5029-1e89] SFI version check [1009] [1009]
[2017.09.14 17:14:25] [0000m.00s.262ms] [0] SingleFile: [ab06-e095-5029-1e89] Database SFI header has been read in [1ms 697us]
[2017.09.14 17:14:25] [0000m.00s.268ms] [0] iCDB Server PI: Server & plugin session [134]
[2017.09.14 17:14:25] [0000m.00s.268ms] [0] iCDB Server PI: Opening database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb]
[2017.09.14 17:14:25] [0000m.00s.268ms] [0] iCDB Server PI: Database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] opened
[2017.09.14 17:14:25] [0000m.00s.299ms] [0] iCDB Database: Checking existence of constraint definitions
[2017.09.14 17:14:25] [0000m.00s.299ms] [0] iCDB Database: Checking constraint definitions version
[2017.09.14 17:14:25] [0000m.00s.355ms] [1] iCDB Database: Constraint definitions version [ce_ee.cns=7.10.24;user.cns=bd5a-97c5-3627-126d-4128-4d69-1284-d650-358d-8c0c-2c99-86c5] up to date
[2017.09.14 17:14:25] [0000m.00s.374ms] [0] iCDB Server PI: Initializing plugins
[2017.09.14 17:14:25] [0000m.00s.377ms] [0] iCDB Server PI: Plugins initialized
[2017.09.14 17:14:25] [0000m.00s.377ms] [0] S: iCDB database loaded
[2017.09.14 17:14:25] [0000m.00s.380ms] [0] S: Starting threads
[2017.09.14 17:14:25] [0000m.00s.381ms] [0] BT: Listening for broadcast messages on port [10000]
[2017.09.14 17:14:25] [0000m.00s.382ms] [1] CMT: There are no clients - waiting [10 seconds]
[2017.09.14 17:14:25] [0000m.00s.387ms] [0] IT: Session [135] for host [W2130] user [levk] with process ID [6632] created
[2017.09.14 17:14:25] [0000m.00s.391ms] [1] SC[135]: Registered sub-client [Expedition PCB_29759734] with ID [1] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:14:25] [0000m.00s.448ms] [1] SC[135]: Registered sub-client [EEVM_EEVM_156425100] with ID [2] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:14:25] [0000m.00s.451ms] [1] SC[135]: UnRegistered sub-client [EEVM_EEVM_156425100] with ID [2] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:14:25] [0000m.00s.484ms] [1] CMT: There are [1] clients now: 135
[2017.09.14 17:14:30] [0000m.05s.084ms] [1] SC[135]: Registered sub-client [ExpeditionCrossProber] with ID [3] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:14:32] [0000m.07s.485ms] [1] SC[135]: Registered sub-client [JobPrefsDBPI_29767281] with ID [4] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:14:32] [0000m.07s.502ms] [1] SC[135]: UnRegistered sub-client [JobPrefsDBPI_29767281] with ID [4] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:14:46] [0000m.21s.137ms] [0] DFAMT: File server access time: 271 us
[2017.09.14 17:31:19] [0016m.53s.976ms] [1] SC[135]: Registered sub-client [JobPrefsDBPI_30773640] with ID [5] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:31:19] [0016m.53s.986ms] [1] SC[135]: UnRegistered sub-client [JobPrefsDBPI_30773640] with ID [5] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:31:20] [0016m.54s.825ms] [1] SC[135]: Registered sub-client [JobPrefsDBPI_30774484] with ID [6] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:31:20] [0016m.54s.963ms] [1] SC[135]: UnRegistered sub-client [JobPrefsDBPI_30774484] with ID [6] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:05] [0020m.40s.553ms] [1] SC[135]: Registered sub-client [JobPrefsDBPI_31000203] with ID [7] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:07] [0020m.42s.348ms] [0] IT: Session [136] for host [W2130] user [levk] with process ID [8368] created
[2017.09.14 17:35:07] [0020m.42s.349ms] [1] SC[136]: Registered sub-client [JobPrefsDBPI_31001953] with ID [1] for session ID [136] host [W2130] user [levk] process ID [8368]
[2017.09.14 17:35:07] [0020m.42s.413ms] [1] CMT: There are [2] clients now: 135, 136
[2017.09.14 17:35:07] [0020m.42s.433ms] [1] SC[136]: Registered sub-client [JobPrefsDBPI_31002078] with ID [2] for session ID [136] host [W2130] user [levk] process ID [8368]
[2017.09.14 17:35:07] [0020m.42s.453ms] [1] SC[136]: UnRegistered sub-client [JobPrefsDBPI_31001953] with ID [1] for session ID [136] host [W2130] user [levk] process ID [8368]
[2017.09.14 17:35:09] [0020m.43s.812ms] [1] SC[136]: UnRegistered sub-client [JobPrefsDBPI_31002078] with ID [2] for session ID [136] host [W2130] user [levk] process ID [8368]
[2017.09.14 17:35:09] [0020m.43s.853ms] [1] CMT: Removed client [136]
[2017.09.14 17:35:09] [0020m.43s.853ms] [1] CMT: There are [1] clients now: 135
[2017.09.14 17:35:09] [0020m.44s.080ms] [1] SC[135]: UnRegistered sub-client [JobPrefsDBPI_31000203] with ID [7] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:13] [0020m.47s.945ms] [1] SC[135]: Registered sub-client [JobPrefsDBPI_31007593] with ID [8] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:13] [0020m.47s.952ms] [1] SC[135]: UnRegistered sub-client [JobPrefsDBPI_31007593] with ID [8] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:13] [0020m.47s.995ms] [1] SC[135]: Registered sub-client [NetClassDBPI_31007640] with ID [9] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:13] [0020m.48s.079ms] [1] SC[135]: UnRegistered sub-client [NetClassDBPI_31007640] with ID [9] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:13] [0020m.48s.090ms] [1] SC[135]: Registered sub-client [JobPrefsDBPI_31007734] with ID [10] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:13] [0020m.48s.097ms] [1] SC[135]: UnRegistered sub-client [JobPrefsDBPI_31007734] with ID [10] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:29] [0021m.04s.187ms] [1] SC[135]: Registered sub-client [JobPrefsDBPI_31023828] with ID [11] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:29] [0021m.04s.326ms] [1] SC[135]: UnRegistered sub-client [JobPrefsDBPI_31023828] with ID [11] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:35:29] [0021m.04s.659ms] [0] IT: Session [137] for host [W2130] user [levk] with process ID [8032] created
[2017.09.14 17:35:29] [0021m.04s.660ms] [0] IT: Session [138] for host [W2130] user [levk] with process ID [8320] created
[2017.09.14 17:35:29] [0021m.04s.660ms] [1] SC[137]: Registered sub-client [JobPrefsDBPI_31024234] with ID [1] for session ID [137] host [W2130] user [levk] process ID [8032]
[2017.09.14 17:35:29] [0021m.04s.661ms] [1] SC[138]: Registered sub-client [JobPrefsDBPI_31024250] with ID [1] for session ID [138] host [W2130] user [levk] process ID [8320]
[2017.09.14 17:35:29] [0021m.04s.691ms] [1] CMT: There are [3] clients now: 135, 137, 138
[2017.09.14 17:35:30] [0021m.04s.727ms] [1] SC[138]: Registered sub-client [NetClassDBPI_31024359] with ID [2] for session ID [138] host [W2130] user [levk] process ID [8320]
[2017.09.14 17:35:30] [0021m.04s.758ms] [1] SC[137]: Registered sub-client [JobPrefsDBPI_31024406] with ID [2] for session ID [137] host [W2130] user [levk] process ID [8032]
[2017.09.14 17:35:30] [0021m.04s.785ms] [1] SC[137]: Registered sub-client [NetClassDBPI_31024437] with ID [3] for session ID [137] host [W2130] user [levk] process ID [8032]
[2017.09.14 17:35:30] [0021m.04s.880ms] [1] SC[138]: Registered sub-client [JobPrefsDBPI_31024531] with ID [3] for session ID [138] host [W2130] user [levk] process ID [8320]
[2017.09.14 17:35:30] [0021m.04s.896ms] [1] SC[137]: Registered sub-client [Expedition_31024546] with ID [4] for session ID [137] host [W2130] user [levk] process ID [8032]
[2017.09.14 17:35:30] [0021m.04s.896ms] [1] SC[137]: UnRegistered sub-client [Expedition_31024546] with ID [4] for session ID [137] host [W2130] user [levk] process ID [8032]
[2017.09.14 17:35:30] [0021m.05s.411ms] [1] SC[138]: UnRegistered sub-client [JobPrefsDBPI_31024531] with ID [3] for session ID [138] host [W2130] user [levk] process ID [8320]
[2017.09.14 17:35:30] [0021m.05s.412ms] [1] SC[138]: UnRegistered sub-client [JobPrefsDBPI_31024250] with ID [1] for session ID [138] host [W2130] user [levk] process ID [8320]
[2017.09.14 17:35:30] [0021m.05s.416ms] [1] SC[138]: UnRegistered sub-client [NetClassDBPI_31024359] with ID [2] for session ID [138] host [W2130] user [levk] process ID [8320]
[2017.09.14 17:35:30] [0021m.05s.476ms] [1] CMT: Removed client [138]
[2017.09.14 17:35:30] [0021m.05s.476ms] [1] CMT: There are [2] clients now: 135, 137
[2017.09.14 17:35:32] [0021m.07s.127ms] [1] SC[137]: UnRegistered sub-client [JobPrefsDBPI_31024406] with ID [2] for session ID [137] host [W2130] user [levk] process ID [8032]
[2017.09.14 17:35:32] [0021m.07s.128ms] [1] SC[137]: UnRegistered sub-client [JobPrefsDBPI_31024234] with ID [1] for session ID [137] host [W2130] user [levk] process ID [8032]
[2017.09.14 17:35:32] [0021m.07s.132ms] [1] SC[137]: UnRegistered sub-client [NetClassDBPI_31024437] with ID [3] for session ID [137] host [W2130] user [levk] process ID [8032]
[2017.09.14 17:35:32] [0021m.07s.222ms] [1] CMT: Removed client [137]
[2017.09.14 17:35:32] [0021m.07s.222ms] [1] CMT: There are [1] clients now: 135
[2017.09.14 17:36:13] [0021m.48s.251ms] [1] SC[135]: UnRegistered sub-client [ExpeditionCrossProber] with ID [3] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:36:13] [0021m.48s.628ms] [1] SC[135]: UnRegistered sub-client [Expedition PCB_29759734] with ID [1] for session ID [135] host [W2130] user [levk] process ID [6632]
[2017.09.14 17:36:13] [0021m.48s.675ms] [1] CMT: Removed client [135]
[2017.09.14 17:36:13] [0021m.48s.675ms] [1] CMT: There are no clients left - waiting [1 seconds]
[2017.09.14 17:36:15] [0021m.49s.715ms] [0] CMT: Starting server auto-close
[2017.09.14 17:36:15] [0021m.49s.763ms] [0] S: Closing server
[2017.09.14 17:36:15] [0021m.49s.828ms] [0] iCDB Server PI: Closing plugins
[2017.09.14 17:36:15] [0021m.49s.828ms] [0] iCDB Server PI: Plugins closed
[2017.09.14 17:36:15] [0021m.49s.828ms] [0] iCDB Server PI: Unloading database
[2017.09.14 17:36:15] [0021m.49s.828ms] [0] iCDB Server PI: Saving storage
[2017.09.14 17:36:15] [0021m.49s.828ms] [0] iCDB Server PI: Closing database
[2017.09.14 17:36:15] [0021m.49s.853ms] [1] iCDB Server PI: Stats: Database defragmentation: max[1] avg[1] holes[51%] data[97%]
[2017.09.14 17:36:15] [0021m.49s.853ms] [1] iCDB Server PI: Stats: Database file size [107984 (105.5 kB)]
[2017.09.14 17:36:15] [0021m.49s.856ms] [1] iCDB Server PI: WARNING: Database needs defragmentation!
[2017.09.14 17:36:15] [0021m.49s.862ms] [0] iCDB Server PI: Database closed
[2017.09.14 17:36:15] [0021m.49s.862ms] [0] iCDB Server PI: Defragmenting database
[2017.09.14 17:36:15] [0021m.49s.863ms] [0] iCDB Server PI: Creating database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] backup to file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\cdbback\2017-09-14 17.36.15-6060-d.zip]
[2017.09.14 17:36:15] [0021m.50s.154ms] [0] iCDB Server PI: Database backup: DONE
[2017.09.14 17:36:15] [0021m.50s.170ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.14 17:36:15] [0021m.50s.170ms] [0] Database GUARD OK: [107984]
[2017.09.14 17:36:15] [0021m.50s.170ms] [0] SingleFile: [ab06-e095-5029-1e89] SFI version check [1009] [1009]
[2017.09.14 17:36:15] [0021m.50s.172ms] [0] SingleFile: [ab06-e095-5029-1e89] Database SFI header has been read in [2ms 145us]
[2017.09.14 17:36:15] [0021m.50s.210ms] [1] iCDB Server PI: Stats: Database file sizes: before[107984 (105.5 kB)], after[73840 (72.1 kB)], gain[34144 (33.3 kB)]
[2017.09.14 17:36:15] [0021m.50s.222ms] [0] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\icdb.dat] closed with size [73840 (72.1 kB)]
[2017.09.14 17:36:15] [0021m.50s.227ms] [0] iCDB Server PI: Database defragmentation completed successfully
[2017.09.14 17:36:15] [0021m.50s.227ms] [1] BackupTransaction: Removing database backup [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\cdbback\2017-09-14 17.36.15-6060-d.zip]
[2017.09.14 17:36:15] [0021m.50s.231ms] [1] iCDB Server PI: Stats: Total iCDB execution time: [41153] ticks
[2017.09.14 17:36:15] [0021m.50s.231ms] [1] iCDB Server PI: Stats: Total iCDB execution time: [13307] microseconds
[2017.09.14 17:36:15] [0021m.50s.231ms] [1] iCDB Server PI: Stats: Total iCDB commands count: [441]
[2017.09.14 17:36:15] [0021m.50s.231ms] [1] iCDB Server PI: Stats: Average iCDB speed:        [33139] commands per second
[2017.09.14 17:36:15] [0021m.50s.329ms] [0] S: STOP
